[2017.09.17 15:40:18] [0000m.00s.045ms] [1] FlowInstallVersion: WARNING: Could not get flow install version file [InstallVersion.txt] creation time
[2017.09.17 15:40:18] [0000m.00s.053ms] [0] WELCOME
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] iCDB Server ver.1.094, vt40T8RtWJOG2cE2Nlvc5A, build:535943 created 2013-01-08 09:09:26, sources stamp:vt40T8RtWJOG2cE2Nlvc5A
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Copyright 2013 Mentor Graphics Corporation. All Rights Reserved.
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Base flow version [7.9.4]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] GBS Install Id [26418], Flow Id [EE7.9.4], Full Flow Name [Expedition Enterprise Flow], Exact Access Date [Jun 1 2012]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] UPDATE Install Info, GBS Install Id [29736], UPDATE Name [Expedition Enterprise Flow - Update 10 for EE7.9.4]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Machine [W2130]  User [levk]  PID [6364]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Operating system [Microsoft Windows 7 Business Edition, 64-bit Service Pack 1 (build 7601)]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Current working dir [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] PATH [C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\xe\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\wg\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\bin;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\bin;C:\MentorGraphics\7.9.4EE\MGC_HOME.ixn\lib;C:\Program Files\Common Files\Microsoft Shared\Microsoft Online Services;C:\Program Files (x86)\Common Files\Microsoft Shared\Microsoft Online Services;C:\ProgramData\Oracle\Java\javapath;C:\MentorGraphics\Allegro Export ODB++\nv\bin;C:\Windows\system32;C:\Windows;C:\Windows\System32\Wbem;C:\Windows\System32\WindowsPowerShell\v1.0\;C:\Program Files (x86)\Common Files\Acronis\SnapAPI\;C:\Program Files (x86)\Altium Designer S09 Viewer\System;C:\Program Files\Aladdin\eToken\PKIClient\x32;C:\Program Files\Aladdin\eToken\PKIClient\x64;C:\Program Files\TortoiseSVN\bin;C:\Program Files (x86)\Skype\Phone\;C:\Cadence\SPB_16.6\openaccess\bin\win32\opt;C:\Cadence\SPB_16.6\tools\capture;C:\Cadence\SPB_16.6\tools\pspice;C:\Cadence\SPB_16.6\tools\specctra\bin;C:\Cadence\SPB_16.6\tools\fet\bin;C:\Cadence\SPB_16.6\tools\libutil\bin;C:\Cadence\SPB_16.6\tools\bin;C:\Cadence\SPB_16.6\tools\pcb\bin;;C:\MentorGraphics\7.9.4EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\7.9.5EE\SDD_HOME\common\win32\lib;C:\MentorGraphics\1.0CCASM\SDD_HOME\common\win32\lib]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] SDD_HOME [C:\MentorGraphics\7.9.4EE\SDD_HOME] on file system [NTFS]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] SDD_PLATFORM [win32]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] WDIR [C:\WDIR\7.9.4EE;C:\MentorGraphics\7.9.4EE\SDD_HOME\standard]
[2017.09.17 15:40:18] [0000m.00s.054ms] [1] Local iCDB working dir [C:\WDIR\7.9.4EE\iCDB] on file system [NTFS]
[2017.09.17 15:40:18] [0000m.00s.057ms] [1] S: Config file name [ServerWG.cfg]
[2017.09.17 15:40:18] [0000m.00s.057ms] [2] S: Loading config files
[2017.09.17 15:40:18] [0000m.00s.068ms] [1] Config file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\cdbsvr\ServerWG.cfg] not found
[2017.09.17 15:40:18] [0000m.00s.068ms] [1] Config: Non-default value [System.SingleUserMode] = [no]
[2017.09.17 15:40:18] [0000m.00s.070ms] [3] S: Config loaded
[2017.09.17 15:40:18] [0000m.00s.090ms] [0] S: Project path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.17 15:40:18] [0000m.00s.090ms] [0] S: Project global path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb] on file system [NTFS]
[2017.09.17 15:40:18] [0000m.00s.091ms] [0] S: Project file (.prj) path [\\berg\data\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\FC-PCI-1R0.prj] on file system [UNKNOWN]
[2017.09.17 15:40:18] [0000m.00s.091ms] [0] S: Project file (.prj) local path [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\FC-PCI-1R0.prj] on file system [NTFS]
[2017.09.17 15:40:18] [0000m.00s.096ms] [1] S: WARNING: Database auto-repair support DISABLED
[2017.09.17 15:40:18] [0000m.00s.096ms] [0] S: Multi-user mode (config)
[2017.09.17 15:40:18] [0000m.00s.096ms] [0] S: Event polling mode autodetection
[2017.09.17 15:40:18] [0000m.00s.105ms] [0] S: Started up automatically by iCDB Client: W2130:levk:8596
[2017.09.17 15:40:18] [0000m.00s.105ms] [0] S: Auto close mode
[2017.09.17 15:40:18] [0000m.00s.105ms] [0] S: Database ID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.17 15:40:18] [0000m.00s.105ms] [0] PathTranslator: BEGIN dumping
[2017.09.17 15:40:18] [0000m.00s.105ms] [0] PathTranslator: Local -> global path prefix list
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [V:\] -> [\\SQLSERVER\Software\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [W:\] -> [\\SQLSERVER\AppDistribution\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [X:\] -> [\\BERG\system\Ufolders\levk\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [Y:\] -> [\\berg\programs\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [Z:\] -> [\\berg\data\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: Global -> local path prefix list
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [\\SQLSERVER\AppDistribution\] -> [W:\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [\\BERG\system\Ufolders\levk\] -> [X:\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [\\SQLSERVER\Software\] -> [V:\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [\\berg\programs\] -> [Y:\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: [\\berg\data\] -> [Z:\]
[2017.09.17 15:40:18] [0000m.00s.106ms] [0] PathTranslator: END dumping
[2017.09.17 15:40:18] [0000m.00s.107ms] [0] S: START
[2017.09.17 15:40:18] [0000m.00s.108ms] [0] S: Server startup lock file created
[2017.09.17 15:40:18] [0000m.00s.112ms] [0] S: Listening for client connections on port [10000]
[2017.09.17 15:40:18] [0000m.00s.112ms] [0] S: Server network ID [W2130:192.168.10.119,W2130,W2130.VG.LOCAL:10000]
[2017.09.17 15:40:18] [0000m.00s.112ms] [0] S: Server GUID [ff35-ecb8-ec62-78e1-23d6-eb97-3d6e-b950-f5de-8c0c-2cd9-86c5]
[2017.09.17 15:40:18] [0000m.00s.112ms] [0] S: Project GUID [9158-59ed-635f-dd63-6b0f-fa9b-b0ff-a950-beae-8c0c-2cd9-86c5]
[2017.09.17 15:40:18] [0000m.00s.121ms] [0] S: Wrote data to server address file
[2017.09.17 15:40:18] [0000m.00s.122ms] [0] S: Server startup lock file removed
[2017.09.17 15:40:18] [0000m.00s.124ms] [1] ProjectBackupManager: Setting project file (.prj) path: [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\FC-PCI-1R0.prj]
[2017.09.17 15:40:18] [0000m.00s.270ms] [0] S: Plugins loaded: [PIM] [ENetGen70] 
[2017.09.17 15:40:18] [0000m.00s.271ms] [0] S: Local disc access time: 44 us
[2017.09.17 15:40:18] [0000m.00s.271ms] [0] S: File server access time: 212 us
[2017.09.17 15:40:19] [0000m.00s.303ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\icdb.dat] size [73840 (72.1 kB)]
[2017.09.17 15:40:19] [0000m.00s.308ms] [1] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\icdb.dat] opened in read-write mode
[2017.09.17 15:40:19] [0000m.00s.316ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 15:40:19] [0000m.00s.316ms] [0] Database GUARD OK: [73840]
[2017.09.17 15:40:19] [0000m.00s.333ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 15:40:19] [0000m.00s.333ms] [0] Database GUARD OK: [73840]
[2017.09.17 15:40:19] [0000m.00s.333ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.17 15:40:19] [0000m.00s.334ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [1ms 737us]
[2017.09.17 15:40:19] [0000m.00s.341ms] [0] iCDB Server PI: Server & plugin session [152]
[2017.09.17 15:40:19] [0000m.00s.341ms] [0] iCDB Server PI: Opening database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb]
[2017.09.17 15:40:19] [0000m.00s.341ms] [0] iCDB Server PI: Database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb] opened
[2017.09.17 15:40:19] [0000m.00s.372ms] [0] iCDB Database: Checking existence of constraint definitions
[2017.09.17 15:40:19] [0000m.00s.372ms] [0] iCDB Database: Checking constraint definitions version
[2017.09.17 15:40:19] [0000m.00s.432ms] [1] iCDB Database: Constraint definitions version [ce_ee.cns=7.10.24;user.cns=bd5a-97c5-3627-126d-4128-4d69-1284-d650-358d-8c0c-2c99-86c5] up to date
[2017.09.17 15:40:19] [0000m.00s.451ms] [0] iCDB Server PI: Initializing plugins
[2017.09.17 15:40:19] [0000m.00s.454ms] [0] iCDB Server PI: Plugins initialized
[2017.09.17 15:40:19] [0000m.00s.454ms] [0] S: iCDB database loaded
[2017.09.17 15:40:19] [0000m.00s.457ms] [0] S: Starting threads
[2017.09.17 15:40:19] [0000m.00s.457ms] [0] BT: Listening for broadcast messages on port [10000]
[2017.09.17 15:40:19] [0000m.00s.459ms] [1] CMT: There are no clients - waiting [10 seconds]
[2017.09.17 15:40:19] [0000m.00s.464ms] [0] IT: Session [153] for host [W2130] user [levk] with process ID [8596] created
[2017.09.17 15:40:19] [0000m.00s.466ms] [1] SC[153]: Registered sub-client [Expedition PCB_21474484] with ID [1] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:19] [0000m.00s.499ms] [1] SC[153]: Registered sub-client [EEVM_EEVM_158917516] with ID [2] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:19] [0000m.00s.500ms] [1] SC[153]: UnRegistered sub-client [EEVM_EEVM_158917516] with ID [2] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:19] [0000m.00s.559ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 15:40:22] [0000m.03s.809ms] [1] SC[153]: Registered sub-client [ExpeditionCrossProber] with ID [3] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:24] [0000m.05s.696ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21480250] with ID [4] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:24] [0000m.05s.709ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21480250] with ID [4] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:40:39] [0000m.21s.127ms] [0] DFAMT: File server access time: 277 us
[2017.09.17 15:41:00] [0000m.41s.450ms] [1] SC[153]: UnRegistered sub-client [ExpeditionCrossProber] with ID [3] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:41:00] [0000m.41s.463ms] [1] SC[153]: Registered sub-client [EEVM_EEVM_1633988] with ID [5] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:41:00] [0000m.41s.463ms] [1] SC[153]: UnRegistered sub-client [EEVM_EEVM_1633988] with ID [5] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:41:01] [0000m.42s.530ms] [0] IT: Session [154] for host [W2130] user [levk] with process ID [5488] created
[2017.09.17 15:41:01] [0000m.42s.531ms] [1] SC[154]: Registered sub-client [JobPrefsDBPI_21517046] with ID [1] for session ID [154] host [W2130] user [levk] process ID [5488]
[2017.09.17 15:41:01] [0000m.42s.538ms] [1] CMT: There are [2] clients now: 153, 154
[2017.09.17 15:41:02] [0000m.43s.310ms] [1] SC[154]: UnRegistered sub-client [JobPrefsDBPI_21517046] with ID [1] for session ID [154] host [W2130] user [levk] process ID [5488]
[2017.09.17 15:41:02] [0000m.43s.392ms] [1] CMT: Removed client [154]
[2017.09.17 15:41:02] [0000m.43s.392ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 15:41:02] [0000m.44s.102ms] [1] SC[153]: Registered sub-client [ExpeditionCrossProber] with ID [6] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:45:28] [0005m.10s.005ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21784546] with ID [7] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:45:28] [0005m.10s.019ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21784546] with ID [7] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:46:51] [0006m.32s.356ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21866906] with ID [8] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:46:51] [0006m.32s.369ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21866906] with ID [8] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:13] [0007m.54s.321ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21948859] with ID [9] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:13] [0007m.54s.337ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21948859] with ID [9] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:28] [0008m.10s.277ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21964828] with ID [10] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:32] [0008m.13s.603ms] [0] IT: Session [155] for host [W2130] user [levk] with process ID [8304] created
[2017.09.17 15:48:32] [0008m.13s.604ms] [1] SC[155]: Registered sub-client [JobPrefsDBPI_21968093] with ID [1] for session ID [155] host [W2130] user [levk] process ID [8304]
[2017.09.17 15:48:32] [0008m.13s.659ms] [1] SC[155]: UnRegistered sub-client [JobPrefsDBPI_21968093] with ID [1] for session ID [155] host [W2130] user [levk] process ID [8304]
[2017.09.17 15:48:32] [0008m.13s.701ms] [1] CMT: There are [2] clients now: 153, 155
[2017.09.17 15:48:32] [0008m.13s.701ms] [1] CMT: Removed client [155]
[2017.09.17 15:48:32] [0008m.13s.701ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 15:48:34] [0008m.15s.713ms] [1] SC[153]: Registered sub-client [JobPrefsDBPI_21970265] with ID [11] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:34] [0008m.15s.751ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21970265] with ID [11] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:48:34] [0008m.15s.804ms] [1] SC[153]: UnRegistered sub-client [JobPrefsDBPI_21964828] with ID [10] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:53:21] [0013m.02s.366ms] [1] PrjBackupMgr: Successfully created Project Backup [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\ProjectBackup\backups\2017-09-17 15.53.19 - auto.zip] with GUID [c006-6cfe-7a32-e731-f307-eb94-407e-b950-b92d-8c0c-2cd9-86c5]
[2017.09.17 15:53:21] [0013m.02s.371ms] [0] PrjBackupMgr: Removing excessive backup:  BackupGUID[5eff-243c-a4ff-30cc-e7e6-6b93-8e66-b950-3c9d-8c0c-2cd9-86c5] CreationTime[Monday, September 11, 2017 11:07:42] Type[Autosave] Creator[iCDB Server (2017-09-11 13.16.23-6580)] ApplicationName[iCDB Server (2017-09-11 13.16.23-6580)] UserName[levk] MachineName[W2130] Description[Autosave] FullFlowName[Expedition Enterprise Flow; Expedition Enterprise Flow - Update 10 for EE7.9.4] FlowId[EE7.9.4] GBSInstallId[26418; 29736] GBSBuildId[535943] IcdbVersion[2709] ReferenceLogFilePath[] BackupFilePath[Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\ProjectBackup\backups\2017-09-11 14.07.42 - auto.zip] BackupFileSize[2097105] Persistent[FALSE]
[2017.09.17 15:53:21] [0013m.02s.375ms] [0] PrjBackupMgr: Project Backup file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\ProjectBackup\backups\2017-09-11 14.07.42 - auto.zip] with GUID [5eff-243c-a4ff-30cc-e7e6-6b93-8e66-b950-3c9d-8c0c-2cd9-86c5] removed
[2017.09.17 15:53:21] [0013m.02s.379ms] [0] PrjBackupEngine: Successfully created backup:  BackupGUID[c006-6cfe-7a32-e731-f307-eb94-407e-b950-b92d-8c0c-2cd9-86c5] CreationTime[Sunday, September 17, 2017 12:53:19] Type[Autosave] Creator[iCDB Server (2017-09-17 15.40.18-6364)] ApplicationName[iCDB Server (2017-09-17 15.40.18-6364)] UserName[levk] MachineName[W2130] Description[Autosave] FullFlowName[Expedition Enterprise Flow; Expedition Enterprise Flow - Update 10 for EE7.9.4] FlowId[EE7.9.4] GBSInstallId[26418; 29736] GBSBuildId[535943] IcdbVersion[2709] ReferenceLogFilePath[] BackupFilePath[Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\ProjectBackup\backups\2017-09-17 15.53.19 - auto.zip] BackupFileSize[2580078] Persistent[FALSE]
[2017.09.17 15:58:20] [0018m.01s.592ms] [1] SC[153]: UnRegistered sub-client [ExpeditionCrossProber] with ID [6] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:58:20] [0018m.01s.607ms] [1] SC[153]: Registered sub-client [EEVM_EEVM_1633988] with ID [12] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:58:20] [0018m.01s.613ms] [1] SC[153]: UnRegistered sub-client [EEVM_EEVM_1633988] with ID [12] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 15:58:22] [0018m.04s.035ms] [0] IT: Session [156] for host [W2130] user [levk] with process ID [3296] created
[2017.09.17 15:58:22] [0018m.04s.035ms] [1] SC[156]: Registered sub-client [JobPrefsDBPI_22558562] with ID [1] for session ID [156] host [W2130] user [levk] process ID [3296]
[2017.09.17 15:58:22] [0018m.04s.077ms] [1] CMT: There are [2] clients now: 153, 156
[2017.09.17 15:58:23] [0018m.04s.780ms] [1] SC[156]: UnRegistered sub-client [JobPrefsDBPI_22558562] with ID [1] for session ID [156] host [W2130] user [levk] process ID [3296]
[2017.09.17 15:58:23] [0018m.04s.783ms] [1] CMT: Removed client [156]
[2017.09.17 15:58:23] [0018m.04s.783ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 15:58:24] [0018m.05s.764ms] [1] SC[153]: Registered sub-client [ExpeditionCrossProber] with ID [13] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:03] [0034m.45s.177ms] [1] SC[153]: UnRegistered sub-client [ExpeditionCrossProber] with ID [13] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:03] [0034m.45s.188ms] [1] SC[153]: Registered sub-client [EEVM_EEVM_1633988] with ID [14] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:03] [0034m.45s.188ms] [1] SC[153]: UnRegistered sub-client [EEVM_EEVM_1633988] with ID [14] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:04] [0034m.46s.021ms] [0] IT: Session [157] for host [W2130] user [levk] with process ID [2452] created
[2017.09.17 16:15:04] [0034m.46s.021ms] [1] SC[157]: Registered sub-client [JobPrefsDBPI_23560640] with ID [1] for session ID [157] host [W2130] user [levk] process ID [2452]
[2017.09.17 16:15:04] [0034m.46s.071ms] [1] CMT: There are [2] clients now: 153, 157
[2017.09.17 16:15:05] [0034m.46s.749ms] [1] SC[157]: UnRegistered sub-client [JobPrefsDBPI_23560640] with ID [1] for session ID [157] host [W2130] user [levk] process ID [2452]
[2017.09.17 16:15:05] [0034m.46s.775ms] [1] CMT: Removed client [157]
[2017.09.17 16:15:05] [0034m.46s.775ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 16:15:07] [0034m.48s.327ms] [0] IT: Session [158] for host [W2130] user [levk] with process ID [8204] created
[2017.09.17 16:15:07] [0034m.48s.328ms] [1] SC[158]: Registered sub-client [JobPrefsDBPI_23562937] with ID [1] for session ID [158] host [W2130] user [levk] process ID [8204]
[2017.09.17 16:15:07] [0034m.48s.392ms] [1] CMT: There are [2] clients now: 153, 158
[2017.09.17 16:15:07] [0034m.48s.399ms] [1] SC[158]: Registered sub-client [Expedition_23563062] with ID [2] for session ID [158] host [W2130] user [levk] process ID [8204]
[2017.09.17 16:15:07] [0034m.48s.589ms] [1] SC[158]: UnRegistered sub-client [JobPrefsDBPI_23562937] with ID [1] for session ID [158] host [W2130] user [levk] process ID [8204]
[2017.09.17 16:15:07] [0034m.48s.705ms] [1] SC[158]: UnRegistered sub-client [Expedition_23563062] with ID [2] for session ID [158] host [W2130] user [levk] process ID [8204]
[2017.09.17 16:15:07] [0034m.48s.796ms] [1] CMT: Removed client [158]
[2017.09.17 16:15:07] [0034m.48s.796ms] [1] CMT: There are [1] clients now: 153
[2017.09.17 16:15:07] [0034m.49s.278ms] [1] SC[153]: UnRegistered sub-client [Expedition PCB_21474484] with ID [1] for session ID [153] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:08] [0034m.49s.305ms] [1] CMT: Removed client [153]
[2017.09.17 16:15:08] [0034m.49s.305ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.17 16:15:08] [0034m.50s.148ms] [0] IT: Session [159] for host [W2130] user [levk] with process ID [8596] created
[2017.09.17 16:15:08] [0034m.50s.149ms] [1] SC[159]: Registered sub-client [Expedition PCB_23564765] with ID [1] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:11] [0034m.52s.327ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_23567000] with ID [2] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:11] [0034m.52s.333ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_23567000] with ID [2] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:11] [0034m.52s.400ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_23567078] with ID [3] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:11] [0034m.52s.408ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_23567078] with ID [3] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:15:11] [0034m.52s.432ms] [1] SC[159]: Registered sub-client [ExpeditionCrossProber] with ID [4] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:46:51] [0066m.32s.483ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_25467250] with ID [5] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:46:51] [0066m.32s.504ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_25467250] with ID [5] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:46:52] [0066m.33s.516ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_25468312] with ID [6] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:46:52] [0066m.33s.672ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_25468312] with ID [6] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:50:13] [0069m.55s.259ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_25670062] with ID [7] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:50:17] [0069m.58s.358ms] [0] IT: Session [160] for host [W2130] user [levk] with process ID [7468] created
[2017.09.17 16:50:17] [0069m.58s.359ms] [1] SC[160]: Registered sub-client [JobPrefsDBPI_25673093] with ID [1] for session ID [160] host [W2130] user [levk] process ID [7468]
[2017.09.17 16:50:17] [0069m.58s.395ms] [1] CMT: There are [2] clients now: 159, 160
[2017.09.17 16:50:17] [0069m.58s.420ms] [1] SC[160]: UnRegistered sub-client [JobPrefsDBPI_25673093] with ID [1] for session ID [160] host [W2130] user [levk] process ID [7468]
[2017.09.17 16:50:17] [0069m.58s.496ms] [1] CMT: Removed client [160]
[2017.09.17 16:50:17] [0069m.58s.496ms] [1] CMT: There are [1] clients now: 159
[2017.09.17 16:50:18] [0069m.59s.921ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_25674718] with ID [8] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:50:18] [0069m.59s.943ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_25674718] with ID [8] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 16:50:18] [0069m.59s.993ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_25670062] with ID [7] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:24] [0105m.05s.406ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27780125] with ID [9] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:26] [0105m.07s.577ms] [0] IT: Session [161] for host [W2130] user [levk] with process ID [1596] created
[2017.09.17 17:25:26] [0105m.07s.577ms] [1] SC[161]: Registered sub-client [JobPrefsDBPI_27782234] with ID [1] for session ID [161] host [W2130] user [levk] process ID [1596]
[2017.09.17 17:25:26] [0105m.07s.623ms] [1] CMT: There are [2] clients now: 159, 161
[2017.09.17 17:25:26] [0105m.07s.717ms] [1] SC[161]: Registered sub-client [JobPrefsDBPI_27782453] with ID [2] for session ID [161] host [W2130] user [levk] process ID [1596]
[2017.09.17 17:25:26] [0105m.07s.757ms] [1] SC[161]: UnRegistered sub-client [JobPrefsDBPI_27782234] with ID [1] for session ID [161] host [W2130] user [levk] process ID [1596]
[2017.09.17 17:25:28] [0105m.09s.414ms] [1] SC[161]: UnRegistered sub-client [JobPrefsDBPI_27782453] with ID [2] for session ID [161] host [W2130] user [levk] process ID [1596]
[2017.09.17 17:25:28] [0105m.09s.489ms] [1] CMT: Removed client [161]
[2017.09.17 17:25:28] [0105m.09s.489ms] [1] CMT: There are [1] clients now: 159
[2017.09.17 17:25:28] [0105m.09s.644ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27780125] with ID [9] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:39] [0105m.21s.103ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27795843] with ID [10] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:42] [0105m.23s.986ms] [0] IT: Session [162] for host [W2130] user [levk] with process ID [4448] created
[2017.09.17 17:25:42] [0105m.23s.986ms] [1] SC[162]: Registered sub-client [JobPrefsDBPI_27798671] with ID [1] for session ID [162] host [W2130] user [levk] process ID [4448]
[2017.09.17 17:25:42] [0105m.24s.045ms] [1] CMT: There are [2] clients now: 159, 162
[2017.09.17 17:25:42] [0105m.24s.049ms] [1] SC[162]: UnRegistered sub-client [JobPrefsDBPI_27798671] with ID [1] for session ID [162] host [W2130] user [levk] process ID [4448]
[2017.09.17 17:25:42] [0105m.24s.146ms] [1] CMT: Removed client [162]
[2017.09.17 17:25:42] [0105m.24s.146ms] [1] CMT: There are [1] clients now: 159
[2017.09.17 17:25:44] [0105m.25s.574ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27800312] with ID [11] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:44] [0105m.25s.596ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27800312] with ID [11] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:25:44] [0105m.25s.817ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27795843] with ID [10] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.51s.808ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27826546] with ID [12] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.51s.820ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27826546] with ID [12] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.51s.868ms] [1] SC[159]: Registered sub-client [NetClassDBPI_27826609] with ID [13] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.51s.980ms] [1] SC[159]: UnRegistered sub-client [NetClassDBPI_27826609] with ID [13] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.51s.991ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27826734] with ID [14] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:10] [0105m.52s.006ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27826734] with ID [14] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:11] [0105m.53s.277ms] [1] SC[159]: Registered sub-client [JobPrefsDBPI_27828015] with ID [15] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:12] [0105m.53s.510ms] [1] SC[159]: UnRegistered sub-client [JobPrefsDBPI_27828015] with ID [15] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:12] [0105m.53s.808ms] [0] IT: Session [163] for host [W2130] user [levk] with process ID [6956] created
[2017.09.17 17:26:12] [0105m.53s.825ms] [1] SC[163]: Registered sub-client [JobPrefsDBPI_27828468] with ID [1] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:12] [0105m.53s.844ms] [0] IT: Session [164] for host [W2130] user [levk] with process ID [9072] created
[2017.09.17 17:26:12] [0105m.53s.844ms] [1] SC[164]: Registered sub-client [JobPrefsDBPI_27828468] with ID [1] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:12] [0105m.53s.861ms] [1] CMT: There are [3] clients now: 159, 163, 164
[2017.09.17 17:26:12] [0105m.53s.888ms] [1] SC[163]: Registered sub-client [NetClassDBPI_27828625] with ID [2] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:12] [0105m.54s.252ms] [1] SC[164]: Registered sub-client [JobPrefsDBPI_27829000] with ID [2] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:12] [0105m.54s.283ms] [1] SC[164]: Registered sub-client [NetClassDBPI_27829031] with ID [3] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:13] [0105m.54s.295ms] [1] SC[163]: Registered sub-client [JobPrefsDBPI_27829031] with ID [3] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:13] [0105m.54s.387ms] [1] SC[164]: Registered sub-client [Expedition_27829125] with ID [4] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:13] [0105m.54s.390ms] [1] SC[164]: UnRegistered sub-client [Expedition_27829125] with ID [4] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:13] [0105m.55s.119ms] [1] SC[163]: UnRegistered sub-client [JobPrefsDBPI_27829031] with ID [3] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:13] [0105m.55s.121ms] [1] SC[163]: UnRegistered sub-client [JobPrefsDBPI_27828468] with ID [1] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:13] [0105m.55s.125ms] [1] SC[163]: UnRegistered sub-client [NetClassDBPI_27828625] with ID [2] for session ID [163] host [W2130] user [levk] process ID [6956]
[2017.09.17 17:26:13] [0105m.55s.220ms] [1] CMT: Removed client [163]
[2017.09.17 17:26:13] [0105m.55s.220ms] [1] CMT: There are [2] clients now: 159, 164
[2017.09.17 17:26:15] [0105m.56s.990ms] [1] SC[164]: UnRegistered sub-client [JobPrefsDBPI_27829000] with ID [2] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:15] [0105m.56s.991ms] [1] SC[164]: UnRegistered sub-client [JobPrefsDBPI_27828468] with ID [1] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:15] [0105m.56s.994ms] [1] SC[164]: UnRegistered sub-client [NetClassDBPI_27829031] with ID [3] for session ID [164] host [W2130] user [levk] process ID [9072]
[2017.09.17 17:26:15] [0105m.57s.033ms] [1] CMT: Removed client [164]
[2017.09.17 17:26:15] [0105m.57s.033ms] [1] CMT: There are [1] clients now: 159
[2017.09.17 17:26:53] [0106m.35s.120ms] [1] SC[159]: UnRegistered sub-client [ExpeditionCrossProber] with ID [4] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:54] [0106m.35s.584ms] [1] SC[159]: UnRegistered sub-client [Expedition PCB_23564765] with ID [1] for session ID [159] host [W2130] user [levk] process ID [8596]
[2017.09.17 17:26:54] [0106m.35s.670ms] [1] CMT: Removed client [159]
[2017.09.17 17:26:54] [0106m.35s.670ms] [1] CMT: There are no clients left - waiting [1 seconds]
[2017.09.17 17:26:56] [0106m.37s.380ms] [0] CMT: Starting server auto-close
[2017.09.17 17:26:56] [0106m.37s.446ms] [0] S: Closing server
[2017.09.17 17:26:56] [0106m.37s.492ms] [0] iCDB Server PI: Closing plugins
[2017.09.17 17:26:56] [0106m.37s.492ms] [0] iCDB Server PI: Plugins closed
[2017.09.17 17:26:56] [0106m.37s.492ms] [0] iCDB Server PI: Unloading database
[2017.09.17 17:26:56] [0106m.37s.493ms] [0] iCDB Server PI: Saving storage
[2017.09.17 17:26:56] [0106m.37s.493ms] [0] iCDB Server PI: Closing database
[2017.09.17 17:26:56] [0106m.37s.497ms] [1] iCDB Server PI: Stats: Database defragmentation: max[2] avg[1] holes[57%] data[88%]
[2017.09.17 17:26:56] [0106m.37s.497ms] [1] iCDB Server PI: Stats: Database file size [115672 (113.0 kB)]
[2017.09.17 17:26:56] [0106m.37s.502ms] [1] iCDB Server PI: WARNING: Database needs defragmentation!
[2017.09.17 17:26:56] [0106m.37s.513ms] [0] iCDB Server PI: Database closed
[2017.09.17 17:26:56] [0106m.37s.513ms] [0] iCDB Server PI: Defragmenting database
[2017.09.17 17:26:56] [0106m.37s.514ms] [0] iCDB Server PI: Creating database [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb] backup to file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-17 17.26.56-6364-d.zip]
[2017.09.17 17:26:56] [0106m.37s.762ms] [0] iCDB Server PI: Database backup: DONE
[2017.09.17 17:26:56] [0106m.37s.772ms] [0] SingleFile: [0000-0000-0000-0000] SFI header version check [1009] [1009]
[2017.09.17 17:26:56] [0106m.37s.772ms] [0] Database GUARD OK: [115672]
[2017.09.17 17:26:56] [0106m.37s.772ms] [0] SingleFile: [ab06-e095-5029-1e89] SFI version check [1009] [1009]
[2017.09.17 17:26:56] [0106m.37s.774ms] [0] SingleFile: [ab06-e095-5029-1e89] Database SFI header has been read in [2ms 313us]
[2017.09.17 17:26:56] [0106m.37s.797ms] [1] iCDB Server PI: Stats: Database file sizes: before[115672 (113.0 kB)], after[73904 (72.2 kB)], gain[41768 (40.8 kB)]
[2017.09.17 17:26:56] [0106m.37s.805ms] [0] Single file [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\icdb.dat] closed with size [73904 (72.2 kB)]
[2017.09.17 17:26:56] [0106m.37s.808ms] [0] iCDB Server PI: Database defragmentation completed successfully
[2017.09.17 17:26:56] [0106m.37s.808ms] [1] BackupTransaction: Removing database backup [Z:\Projects\Boards\VB\COMPULAB\CM-188_FC-PCI_Rev1.0\PCB\keyin.icdb\cdbback\2017-09-17 17.26.56-6364-d.zip]
[2017.09.17 17:26:56] [0106m.37s.811ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [229669] ticks
[2017.09.17 17:26:56] [0106m.37s.811ms] [1] iCDB Server PI: Stats: Total iCDB execution time: [74266] microseconds
[2017.09.17 17:26:56] [0106m.37s.811ms] [1] iCDB Server PI: Stats: Total iCDB commands count: [1119]
[2017.09.17 17:26:56] [0106m.37s.811ms] [1] iCDB Server PI: Stats: Average iCDB speed:        [15067] commands per second
[2017.09.17 17:26:56] [0106m.37s.895ms] [0] S: STOP
